﻿2026-06-21T07:20:32.1032944Z ##[group]Run cargo nextest run -p spt-daemon a_journaled_input_wedge_does_not_starve_a_concurrent_rc_attach --no-capture 2>&1 | grep -E 'WEDGE_TRACE|W1b GATE|PASS|FAIL' || true
2026-06-21T07:20:32.1033974Z [36;1mcargo nextest run -p spt-daemon a_journaled_input_wedge_does_not_starve_a_concurrent_rc_attach --no-capture 2>&1 | grep -E 'WEDGE_TRACE|W1b GATE|PASS|FAIL' || true[0m
2026-06-21T07:20:32.1046608Z shell: /usr/bin/bash -e {0}
2026-06-21T07:20:32.1046870Z env:
2026-06-21T07:20:32.1047113Z   RUSTFLAGS: -C link-arg=-fuse-ld=mold
2026-06-21T07:20:32.1047384Z   SPT_WEDGE_TRACE: 1
2026-06-21T07:20:32.1047604Z ##[endgroup]
2026-06-21T07:22:00.9093741Z WEDGE_TRACE conn_open drain_appends=0
2026-06-21T07:22:00.9094562Z WEDGE_TRACE conn_hello drain_appends=0
2026-06-21T07:22:00.9094986Z WEDGE_TRACE conn_open drain_appends=1
2026-06-21T07:22:00.9095404Z WEDGE_TRACE conn_hello drain_appends=1
2026-06-21T07:22:00.9095927Z WEDGE_TRACE conn_read kind=subscribe drain_appends_at_read=1
2026-06-21T07:22:00.9096504Z WEDGE_TRACE subscribe sid=1 sessions_lock_wait_us=0
2026-06-21T07:22:00.9097305Z WEDGE_TRACE subscribe sid=1 log_lock_wait_us=86 resolve_us=504 drain_appends_so_far=2
2026-06-21T07:22:00.9098437Z WEDGE_TRACE subscribe sid=1 reply_send_us=43
2026-06-21T07:22:00.9099128Z WEDGE_TRACE drain appends=2000 acquire_wait_total_us=1838 acquire_wait_max_us=452
2026-06-21T07:22:00.9099896Z WEDGE_TRACE drain appends=4000 acquire_wait_total_us=3061 acquire_wait_max_us=452
2026-06-21T07:22:00.9100649Z WEDGE_TRACE drain appends=6000 acquire_wait_total_us=4360 acquire_wait_max_us=452
2026-06-21T07:22:00.9101405Z WEDGE_TRACE drain appends=8000 acquire_wait_total_us=5816 acquire_wait_max_us=452
2026-06-21T07:22:00.9102165Z WEDGE_TRACE drain appends=10000 acquire_wait_total_us=7031 acquire_wait_max_us=452
2026-06-21T07:22:00.9102917Z WEDGE_TRACE drain appends=12000 acquire_wait_total_us=8541 acquire_wait_max_us=452
2026-06-21T07:22:00.9103686Z WEDGE_TRACE drain appends=14000 acquire_wait_total_us=9937 acquire_wait_max_us=452
2026-06-21T07:22:00.9104459Z WEDGE_TRACE drain appends=16000 acquire_wait_total_us=11426 acquire_wait_max_us=452
2026-06-21T07:22:00.9105227Z WEDGE_TRACE drain appends=18000 acquire_wait_total_us=12907 acquire_wait_max_us=452
2026-06-21T07:22:00.9106027Z WEDGE_TRACE drain appends=20000 acquire_wait_total_us=14395 acquire_wait_max_us=452
2026-06-21T07:22:00.9106797Z WEDGE_TRACE drain appends=22000 acquire_wait_total_us=15850 acquire_wait_max_us=452
2026-06-21T07:22:00.9107550Z WEDGE_TRACE drain appends=24000 acquire_wait_total_us=17328 acquire_wait_max_us=452
2026-06-21T07:22:00.9108328Z WEDGE_TRACE drain appends=26000 acquire_wait_total_us=18758 acquire_wait_max_us=452
2026-06-21T07:22:00.9109158Z WEDGE_TRACE drain appends=28000 acquire_wait_total_us=20248 acquire_wait_max_us=452
2026-06-21T07:22:00.9109922Z WEDGE_TRACE drain appends=30000 acquire_wait_total_us=21625 acquire_wait_max_us=452
2026-06-21T07:22:00.9110675Z WEDGE_TRACE drain appends=32000 acquire_wait_total_us=23107 acquire_wait_max_us=452
2026-06-21T07:22:00.9111446Z WEDGE_TRACE drain appends=34000 acquire_wait_total_us=24578 acquire_wait_max_us=452
2026-06-21T07:22:00.9112200Z WEDGE_TRACE drain appends=36000 acquire_wait_total_us=25977 acquire_wait_max_us=452
2026-06-21T07:22:00.9112977Z WEDGE_TRACE drain appends=38000 acquire_wait_total_us=27415 acquire_wait_max_us=452
2026-06-21T07:22:00.9113600Z WEDGE_TRACE conn_open drain_appends=39069
2026-06-21T07:22:00.9114027Z WEDGE_TRACE conn_hello drain_appends=39069
2026-06-21T07:22:00.9114490Z WEDGE_TRACE conn_open drain_appends=39088
2026-06-21T07:22:00.9114972Z WEDGE_TRACE conn_hello drain_appends=39088
2026-06-21T07:22:00.9115496Z WEDGE_TRACE c3-DROP: stream=1 append seq=0 but NO subscriber yet
2026-06-21T07:22:00.9133697Z WEDGE_TRACE conn_read kind=net-stream-subscribe drain_appends_at_read=39088
2026-06-21T07:22:00.9134579Z WEDGE_TRACE subscribe_stream stream=1 streams_lock_wait_us=2 drain_appends=39088 stream_appends=1
2026-06-21T07:22:00.9135724Z WEDGE_TRACE subscribe_stream stream=1 streamlog_log_lock_wait_us=0 attach_us=30 stream_append_wait_total_us=1 stream_append_wait_max_us=1
2026-06-21T07:22:00.9140678Z WEDGE_TRACE net_stream_subscribe stream=1 enter_appends=39088 exit_appends=39088 subscribe_stream_us=59 ok=true
2026-06-21T07:22:00.9141483Z WEDGE_TRACE conn_open drain_appends=39088
2026-06-21T07:22:00.9142069Z WEDGE_TRACE conn_read kind=net-stream-subscribe drain_appends_at_read=39088
2026-06-21T07:22:00.9142941Z WEDGE_TRACE subscribe_stream stream=2 streams_lock_wait_us=0 drain_appends=39088 stream_appends=1
2026-06-21T07:22:00.9144081Z WEDGE_TRACE subscribe_stream stream=2 streamlog_log_lock_wait_us=1 attach_us=2 stream_append_wait_total_us=1 stream_append_wait_max_us=1
2026-06-21T07:22:00.9144977Z WEDGE_TRACE conn_hello drain_appends=39088
2026-06-21T07:22:00.9145732Z WEDGE_TRACE net_stream_subscribe stream=2 enter_appends=39088 exit_appends=39088 subscribe_stream_us=27 ok=true
2026-06-21T07:22:00.9146609Z WEDGE_TRACE conn_read kind=subscribe drain_appends_at_read=39089
2026-06-21T07:22:00.9147195Z WEDGE_TRACE subscribe sid=1 sessions_lock_wait_us=1
2026-06-21T07:22:00.9147859Z WEDGE_TRACE subscribe sid=1 log_lock_wait_us=1 resolve_us=51529 drain_appends_so_far=39089
2026-06-21T07:22:00.9148737Z WEDGE_TRACE subscribe sid=1 reply_send_us=33
2026-06-21T07:22:00.9149348Z WEDGE_TRACE c3: stream=2 live-send seq=0 count=1 write_ok=true
2026-06-21T07:22:00.9150057Z WEDGE_TRACE drain appends=40000 acquire_wait_total_us=80290 acquire_wait_max_us=51493
2026-06-21T07:22:00.9150853Z WEDGE_TRACE drain appends=42000 acquire_wait_total_us=81790 acquire_wait_max_us=51493
2026-06-21T07:22:00.9151661Z WEDGE_TRACE drain appends=44000 acquire_wait_total_us=83260 acquire_wait_max_us=51493
2026-06-21T07:22:00.9152459Z WEDGE_TRACE drain appends=46000 acquire_wait_total_us=84746 acquire_wait_max_us=51493
2026-06-21T07:22:00.9153259Z WEDGE_TRACE drain appends=48000 acquire_wait_total_us=86250 acquire_wait_max_us=51493
2026-06-21T07:22:00.9154050Z WEDGE_TRACE drain appends=50000 acquire_wait_total_us=87573 acquire_wait_max_us=51493
2026-06-21T07:22:00.9154845Z WEDGE_TRACE drain appends=52000 acquire_wait_total_us=89026 acquire_wait_max_us=51493
2026-06-21T07:22:06.0679457Z WEDGE_TRACE drain appends=54000 acquire_wait_total_us=90407 acquire_wait_max_us=51493
2026-06-21T07:22:06.0680440Z WEDGE_TRACE drain appends=56000 acquire_wait_total_us=91762 acquire_wait_max_us=51493
2026-06-21T07:22:06.0681322Z WEDGE_TRACE drain appends=58000 acquire_wait_total_us=93168 acquire_wait_max_us=51493
2026-06-21T07:22:06.0682095Z WEDGE_TRACE drain appends=60000 acquire_wait_total_us=94435 acquire_wait_max_us=51493
2026-06-21T07:22:06.0682848Z WEDGE_TRACE drain appends=62000 acquire_wait_total_us=95792 acquire_wait_max_us=51493
2026-06-21T07:22:06.0683608Z WEDGE_TRACE drain appends=64000 acquire_wait_total_us=97136 acquire_wait_max_us=51493
2026-06-21T07:22:06.0684404Z WEDGE_TRACE drain appends=66000 acquire_wait_total_us=98576 acquire_wait_max_us=51493
2026-06-21T07:22:06.0685167Z WEDGE_TRACE drain appends=68000 acquire_wait_total_us=100089 acquire_wait_max_us=51493
2026-06-21T07:22:06.0685954Z WEDGE_TRACE drain appends=70000 acquire_wait_total_us=101449 acquire_wait_max_us=51493
2026-06-21T07:22:06.0686717Z WEDGE_TRACE drain appends=72000 acquire_wait_total_us=102973 acquire_wait_max_us=51493
2026-06-21T07:22:06.0687513Z WEDGE_TRACE drain appends=74000 acquire_wait_total_us=104342 acquire_wait_max_us=51493
2026-06-21T07:22:06.0688285Z WEDGE_TRACE drain appends=76000 acquire_wait_total_us=105688 acquire_wait_max_us=51493
2026-06-21T07:22:06.0689148Z WEDGE_TRACE drain appends=78000 acquire_wait_total_us=107122 acquire_wait_max_us=51493
2026-06-21T07:22:06.0689912Z WEDGE_TRACE drain appends=80000 acquire_wait_total_us=108519 acquire_wait_max_us=51493
2026-06-21T07:22:06.0690676Z WEDGE_TRACE drain appends=82000 acquire_wait_total_us=110049 acquire_wait_max_us=51493
2026-06-21T07:22:06.0691461Z WEDGE_TRACE drain appends=84000 acquire_wait_total_us=111361 acquire_wait_max_us=51493
2026-06-21T07:22:06.0692223Z WEDGE_TRACE drain appends=86000 acquire_wait_total_us=112776 acquire_wait_max_us=51493
2026-06-21T07:22:06.0693422Z WEDGE_TRACE drain appends=88000 acquire_wait_total_us=114028 acquire_wait_max_us=51493
2026-06-21T07:22:06.0694201Z WEDGE_TRACE drain appends=90000 acquire_wait_total_us=115366 acquire_wait_max_us=51493
2026-06-21T07:22:06.0694974Z WEDGE_TRACE drain appends=92000 acquire_wait_total_us=116643 acquire_wait_max_us=51493
2026-06-21T07:22:06.0695741Z WEDGE_TRACE drain appends=94000 acquire_wait_total_us=117961 acquire_wait_max_us=51493
2026-06-21T07:22:06.0696505Z WEDGE_TRACE drain appends=96000 acquire_wait_total_us=119355 acquire_wait_max_us=51493
2026-06-21T07:22:06.0697282Z WEDGE_TRACE drain appends=98000 acquire_wait_total_us=120654 acquire_wait_max_us=51493
2026-06-21T07:22:06.0698059Z WEDGE_TRACE drain appends=100000 acquire_wait_total_us=122002 acquire_wait_max_us=51493
2026-06-21T07:22:06.0698840Z WEDGE_TRACE drain appends=102000 acquire_wait_total_us=123335 acquire_wait_max_us=51493
2026-06-21T07:22:06.0699718Z WEDGE_TRACE drain appends=104000 acquire_wait_total_us=124636 acquire_wait_max_us=51493
2026-06-21T07:22:06.0700495Z WEDGE_TRACE drain appends=106000 acquire_wait_total_us=126067 acquire_wait_max_us=51493
2026-06-21T07:22:06.0701454Z WEDGE_TRACE drain appends=108000 acquire_wait_total_us=127400 acquire_wait_max_us=51493
2026-06-21T07:22:06.0702232Z WEDGE_TRACE drain appends=110000 acquire_wait_total_us=128738 acquire_wait_max_us=51493
2026-06-21T07:22:06.0703024Z WEDGE_TRACE drain appends=112000 acquire_wait_total_us=130066 acquire_wait_max_us=51493
2026-06-21T07:22:06.0703798Z WEDGE_TRACE drain appends=114000 acquire_wait_total_us=131489 acquire_wait_max_us=51493
2026-06-21T07:22:06.0704573Z WEDGE_TRACE drain appends=116000 acquire_wait_total_us=132949 acquire_wait_max_us=51493
2026-06-21T07:22:06.0705350Z WEDGE_TRACE drain appends=118000 acquire_wait_total_us=134356 acquire_wait_max_us=51493
2026-06-21T07:22:06.0706125Z WEDGE_TRACE drain appends=120000 acquire_wait_total_us=135764 acquire_wait_max_us=51493
2026-06-21T07:22:06.0706922Z WEDGE_TRACE drain appends=122000 acquire_wait_total_us=136947 acquire_wait_max_us=51493
2026-06-21T07:22:06.0707709Z WEDGE_TRACE drain appends=124000 acquire_wait_total_us=138297 acquire_wait_max_us=51493
2026-06-21T07:22:06.0708513Z WEDGE_TRACE drain appends=126000 acquire_wait_total_us=139539 acquire_wait_max_us=51493
2026-06-21T07:22:06.0709372Z WEDGE_TRACE drain appends=128000 acquire_wait_total_us=141030 acquire_wait_max_us=51493
2026-06-21T07:22:06.0710169Z WEDGE_TRACE drain appends=130000 acquire_wait_total_us=142456 acquire_wait_max_us=51493
2026-06-21T07:22:06.0710957Z WEDGE_TRACE drain appends=132000 acquire_wait_total_us=143968 acquire_wait_max_us=51493
2026-06-21T07:22:06.0711738Z WEDGE_TRACE drain appends=134000 acquire_wait_total_us=145482 acquire_wait_max_us=51493
2026-06-21T07:22:06.0712520Z WEDGE_TRACE drain appends=136000 acquire_wait_total_us=146931 acquire_wait_max_us=51493
2026-06-21T07:22:06.0713305Z WEDGE_TRACE drain appends=138000 acquire_wait_total_us=148289 acquire_wait_max_us=51493
2026-06-21T07:22:06.0714101Z WEDGE_TRACE drain appends=140000 acquire_wait_total_us=149712 acquire_wait_max_us=51493
2026-06-21T07:22:06.0714900Z WEDGE_TRACE drain appends=142000 acquire_wait_total_us=151165 acquire_wait_max_us=51493
2026-06-21T07:22:06.0715717Z WEDGE_TRACE drain appends=144000 acquire_wait_total_us=152715 acquire_wait_max_us=51493
2026-06-21T07:22:06.0716518Z WEDGE_TRACE drain appends=146000 acquire_wait_total_us=154214 acquire_wait_max_us=51493
2026-06-21T07:22:16.9062044Z WEDGE_TRACE drain appends=148000 acquire_wait_total_us=155717 acquire_wait_max_us=51493
2026-06-21T07:22:16.9062702Z WEDGE_TRACE drain appends=150000 acquire_wait_total_us=157121 acquire_wait_max_us=51493
2026-06-21T07:22:16.9063251Z WEDGE_TRACE drain appends=152000 acquire_wait_total_us=158622 acquire_wait_max_us=51493
2026-06-21T07:22:16.9063794Z WEDGE_TRACE drain appends=154000 acquire_wait_total_us=160156 acquire_wait_max_us=51493
2026-06-21T07:22:16.9064315Z WEDGE_TRACE drain appends=156000 acquire_wait_total_us=161661 acquire_wait_max_us=51493
2026-06-21T07:22:16.9065287Z WEDGE_TRACE drain appends=158000 acquire_wait_total_us=163134 acquire_wait_max_us=51493
2026-06-21T07:22:16.9065801Z WEDGE_TRACE drain appends=160000 acquire_wait_total_us=164607 acquire_wait_max_us=51493
2026-06-21T07:22:16.9066339Z WEDGE_TRACE drain appends=162000 acquire_wait_total_us=165942 acquire_wait_max_us=51493
2026-06-21T07:22:16.9066874Z WEDGE_TRACE drain appends=164000 acquire_wait_total_us=167264 acquire_wait_max_us=51493
2026-06-21T07:22:16.9067389Z WEDGE_TRACE drain appends=166000 acquire_wait_total_us=168592 acquire_wait_max_us=51493
2026-06-21T07:22:16.9067908Z WEDGE_TRACE drain appends=168000 acquire_wait_total_us=169925 acquire_wait_max_us=51493
2026-06-21T07:22:16.9068420Z WEDGE_TRACE drain appends=170000 acquire_wait_total_us=171148 acquire_wait_max_us=51493
2026-06-21T07:22:16.9068930Z WEDGE_TRACE drain appends=172000 acquire_wait_total_us=172481 acquire_wait_max_us=51493
2026-06-21T07:22:16.9069564Z WEDGE_TRACE drain appends=174000 acquire_wait_total_us=173755 acquire_wait_max_us=51493
2026-06-21T07:22:16.9070079Z WEDGE_TRACE drain appends=176000 acquire_wait_total_us=175101 acquire_wait_max_us=51493
2026-06-21T07:22:16.9070761Z WEDGE_TRACE drain appends=178000 acquire_wait_total_us=176453 acquire_wait_max_us=51493
2026-06-21T07:22:16.9071278Z WEDGE_TRACE drain appends=180000 acquire_wait_total_us=177828 acquire_wait_max_us=51493
2026-06-21T07:22:16.9071783Z WEDGE_TRACE drain appends=182000 acquire_wait_total_us=179310 acquire_wait_max_us=51493
2026-06-21T07:22:16.9072331Z WEDGE_TRACE drain appends=184000 acquire_wait_total_us=180631 acquire_wait_max_us=51493
2026-06-21T07:22:16.9072876Z WEDGE_TRACE drain appends=186000 acquire_wait_total_us=181863 acquire_wait_max_us=51493
2026-06-21T07:22:16.9073257Z WEDGE_TRACE conn_open drain_appends=186824
2026-06-21T07:22:16.9073518Z WEDGE_TRACE conn_hello drain_appends=186824
2026-06-21T07:22:16.9073873Z test a_journaled_input_wedge_does_not_starve_a_concurrent_rc_attach ... FAILED
2026-06-21T07:22:16.9074355Z test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 9 filtered out; finished in 20.62s
2026-06-21T07:22:16.9079991Z         FAIL [  20.637s] (1/1) spt-daemon::inject_control_wedge a_journaled_input_wedge_does_not_starve_a_concurrent_rc_attach
2026-06-21T07:22:16.9080701Z         FAIL [  20.637s] (1/1) spt-daemon::inject_control_wedge a_journaled_input_wedge_does_not_starve_a_concurrent_rc_attach
